[INFO] cloning repository https://github.com/szaffarano/org-mcp-server
[INFO] running `Command { std: "git" "-c" "credential.helper=" "-c" "credential.helper=/workspace/cargo-home/bin/git-credential-null" "clone" "--bare" "https://github.com/szaffarano/org-mcp-server" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fszaffarano%2Forg-mcp-server", kill_on_drop: false }`
[INFO] [stderr] Cloning into bare repository '/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fszaffarano%2Forg-mcp-server'...
[INFO] running `Command { std: "git" "rev-parse" "HEAD", kill_on_drop: false }`
[INFO] [stdout] 89d9eb0368228acd38d41041e89e481ec74714fc
[INFO] testing szaffarano/org-mcp-server against try#0abe69e6c16114311cc2700e972492e64d69c28f for pr-154065-1
[INFO] running `Command { std: "git" "clone" "/workspace/cache/git-repos/https%3A%2F%2Fgithub.com%2Fszaffarano%2Forg-mcp-server" "/workspace/builds/worker-2-tc2/source", kill_on_drop: false }`
[INFO] [stderr] Cloning into '/workspace/builds/worker-2-tc2/source'...
[INFO] [stderr] done.
[INFO] started tweaking git repo https://github.com/szaffarano/org-mcp-server
[INFO] finished tweaking git repo https://github.com/szaffarano/org-mcp-server
[INFO] tweaked toml for git repo https://github.com/szaffarano/org-mcp-server written to /workspace/builds/worker-2-tc2/source/Cargo.toml
[INFO] validating manifest of git repo https://github.com/szaffarano/org-mcp-server on toolchain 0abe69e6c16114311cc2700e972492e64d69c28f
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--manifest-path" "Cargo.toml" "--no-deps", kill_on_drop: false }`
[INFO] crate git repo https://github.com/szaffarano/org-mcp-server already has a lockfile, it will not be regenerated
[INFO] running `Command { std: CARGO_HOME="/workspace/cargo-home" RUSTUP_HOME="/workspace/rustup-home" "/workspace/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "fetch" "--manifest-path" "Cargo.toml", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "metadata" "--no-deps" "--format-version=1", kill_on_drop: false }`
[INFO] [stdout] 03c7c55a9e2536007d1722ceda2da56ed52a994a97eddf035978ae9be3bf6f0f
[INFO] running `Command { std: "docker" "start" "-a" "03c7c55a9e2536007d1722ceda2da56ed52a994a97eddf035978ae9be3bf6f0f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "inspect" "03c7c55a9e2536007d1722ceda2da56ed52a994a97eddf035978ae9be3bf6f0f", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "03c7c55a9e2536007d1722ceda2da56ed52a994a97eddf035978ae9be3bf6f0f", kill_on_drop: false }`
[INFO] [stdout] 03c7c55a9e2536007d1722ceda2da56ed52a994a97eddf035978ae9be3bf6f0f
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "build" "--frozen" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] 0954d98544c48fb7c177380d0975511a638462d7eac2e487a10dd17408b0be50
[INFO] running `Command { std: "docker" "start" "-a" "0954d98544c48fb7c177380d0975511a638462d7eac2e487a10dd17408b0be50", kill_on_drop: false }`
[INFO] [stderr]    Compiling proc-macro2 v1.0.101
[INFO] [stderr]    Compiling quote v1.0.41
[INFO] [stderr]    Compiling unicode-ident v1.0.19
[INFO] [stderr]    Compiling memchr v2.7.6
[INFO] [stderr]    Compiling serde_core v1.0.228
[INFO] [stderr]    Compiling libc v0.2.177
[INFO] [stderr]    Compiling cfg-if v1.0.4
[INFO] [stderr]    Compiling pin-project-lite v0.2.16
[INFO] [stderr]    Compiling serde v1.0.228
[INFO] [stderr]    Compiling itoa v1.0.15
[INFO] [stderr]    Compiling once_cell v1.21.3
[INFO] [stderr]    Compiling futures-core v0.3.31
[INFO] [stderr]    Compiling bytes v1.10.1
[INFO] [stderr]    Compiling log v0.4.28
[INFO] [stderr]    Compiling futures-sink v0.3.31
[INFO] [stderr]    Compiling zmij v1.0.7
[INFO] [stderr]    Compiling crunchy v0.2.4
[INFO] [stderr]    Compiling autocfg v1.5.0
[INFO] [stderr]    Compiling tracing-core v0.1.36
[INFO] [stderr]    Compiling tiny-keccak v2.0.2
[INFO] [stderr]    Compiling pin-utils v0.1.0
[INFO] [stderr]    Compiling futures-channel v0.3.31
[INFO] [stderr]    Compiling getrandom v0.3.4
[INFO] [stderr]    Compiling fnv v1.0.7
[INFO] [stderr]    Compiling regex-syntax v0.8.8
[INFO] [stderr]    Compiling ucd-trie v0.1.7
[INFO] [stderr]    Compiling serde_json v1.0.149
[INFO] [stderr]    Compiling futures-io v0.3.31
[INFO] [stderr]    Compiling slab v0.4.11
[INFO] [stderr]    Compiling typeid v1.0.3
[INFO] [stderr]    Compiling strsim v0.11.1
[INFO] [stderr]    Compiling futures-task v0.3.31
[INFO] [stderr]    Compiling iana-time-zone v0.1.64
[INFO] [stderr]    Compiling crossbeam-utils v0.8.21
[INFO] [stderr]    Compiling hashbrown v0.14.5
[INFO] [stderr]    Compiling num-traits v0.2.19
[INFO] [stderr]    Compiling winnow v0.7.13
[INFO] [stderr]    Compiling erased-serde v0.4.9
[INFO] [stderr]    Compiling aho-corasick v1.1.3
[INFO] [stderr]    Compiling http v1.3.1
[INFO] [stderr]    Compiling pest v2.8.3
[INFO] [stderr]    Compiling zerocopy v0.8.27
[INFO] [stderr]    Compiling httparse v1.10.1
[INFO] [stderr]    Compiling foldhash v0.1.5
[INFO] [stderr]    Compiling syn v2.0.106
[INFO] [stderr]    Compiling hashbrown v0.15.5
[INFO] [stderr]    Compiling ident_case v1.0.1
[INFO] [stderr]    Compiling jetscii v0.5.3
[INFO] [stderr]    Compiling utf8parse v0.2.2
[INFO] [stderr]    Compiling smallvec v1.15.1
[INFO] [stderr]    Compiling option-ext v0.2.0
[INFO] [stderr]    Compiling tower-service v0.3.3
[INFO] [stderr]    Compiling unicode-segmentation v1.12.0
[INFO] [stderr]    Compiling cfg_aliases v0.2.1
[INFO] [stderr]    Compiling nix v0.30.1
[INFO] [stderr]    Compiling getrandom v0.2.16
[INFO] [stderr]    Compiling anstyle-parse v0.2.7
[INFO] [stderr]    Compiling hashlink v0.10.0
[INFO] [stderr]    Compiling const-random-macro v0.1.16
[INFO] [stderr]    Compiling bstr v1.12.0
[INFO] [stderr]    Compiling encoding_rs v0.8.35
[INFO] [stderr]    Compiling anstyle v1.0.13
[INFO] [stderr]    Compiling toml_parser v1.0.8+spec-1.1.0
[INFO] [stderr]    Compiling arraydeque v0.5.1
[INFO] [stderr]    Compiling const-random v0.1.18
[INFO] [stderr]    Compiling dlv-list v0.5.2
[INFO] [stderr]    Compiling ordered-multimap v0.7.3
[INFO] [stderr]    Compiling crossbeam-epoch v0.9.18
[INFO] [stderr]    Compiling minimal-lexical v0.2.1
[INFO] [stderr]    Compiling mio v1.1.0
[INFO] [stderr]    Compiling signal-hook-registry v1.4.6
[INFO] [stderr]    Compiling socket2 v0.6.2
[INFO] [stderr]    Compiling http-body v1.0.1
[INFO] [stderr]    Compiling http-body-util v0.1.3
[INFO] [stderr]    Compiling dirs-sys v0.5.0
[INFO] [stderr]    Compiling regex-automata v0.4.13
[INFO] [stderr]    Compiling text-size v1.1.1
[INFO] [stderr]    Compiling pest_meta v2.8.3
[INFO] [stderr]    Compiling tower-layer v0.3.3
[INFO] [stderr]    Compiling httpdate v1.0.3
[INFO] [stderr]    Compiling sync_wrapper v1.0.2
[INFO] [stderr]    Compiling atomic-waker v1.1.2
[INFO] [stderr]    Compiling paste v1.0.15
[INFO] [stderr]    Compiling is_terminal_polyfill v1.70.1
[INFO] [stderr]    Compiling percent-encoding v2.3.2
[INFO] [stderr]    Compiling anstyle-query v1.1.4
[INFO] [stderr]    Compiling same-file v1.0.6
[INFO] [stderr]    Compiling countme v3.0.1
[INFO] [stderr]    Compiling colorchoice v1.0.4
[INFO] [stderr]    Compiling ref-cast v1.0.25
[INFO] [stderr]    Compiling serde_spanned v1.0.4
[INFO] [stderr]    Compiling toml_datetime v0.7.5+spec-1.1.0
[INFO] [stderr]    Compiling rustc-hash v1.1.0
[INFO] [stderr]    Compiling form_urlencoded v1.2.2
[INFO] [stderr]    Compiling anstream v0.6.21
[INFO] [stderr]    Compiling toml_datetime v1.0.0+spec-1.1.0
[INFO] [stderr]    Compiling walkdir v2.5.0
[INFO] [stderr]    Compiling yaml-rust2 v0.10.4
[INFO] [stderr]    Compiling rowan v0.16.1
[INFO] [stderr]    Compiling serde-untagged v0.1.9
[INFO] [stderr]    Compiling toml v0.9.12+spec-1.1.0
[INFO] [stderr]    Compiling dirs v6.0.0
[INFO] [stderr]    Compiling rand_core v0.9.3
[INFO] [stderr]    Compiling crossbeam-deque v0.8.6
[INFO] [stderr]    Compiling ppv-lite86 v0.2.21
[INFO] [stderr]    Compiling rust-ini v0.21.3
[INFO] [stderr]    Compiling nom v7.1.3
[INFO] [stderr]    Compiling convert_case v0.6.0
[INFO] [stderr]    Compiling clap_lex v1.0.0
[INFO] [stderr]    Compiling ryu v1.0.22
[INFO] [stderr]    Compiling heck v0.5.0
[INFO] [stderr]    Compiling thiserror v2.0.17
[INFO] [stderr]    Compiling bytecount v0.6.9
[INFO] [stderr]    Compiling mime v0.3.17
[INFO] [stderr]    Compiling equivalent v1.0.2
[INFO] [stderr]    Compiling pathdiff v0.2.3
[INFO] [stderr]    Compiling hashbrown v0.16.0
[INFO] [stderr]    Compiling toml_writer v1.0.6+spec-1.1.0
[INFO] [stderr]    Compiling rand_chacha v0.9.0
[INFO] [stderr]    Compiling clap_builder v4.5.58
[INFO] [stderr]    Compiling shellexpand v3.1.1
[INFO] [stderr]    Compiling serde_path_to_error v0.1.20
[INFO] [stderr]    Compiling nucleo-matcher v0.3.1
[INFO] [stderr]    Compiling toml v1.0.1+spec-1.1.0
[INFO] [stderr]    Compiling indexmap v2.12.0
[INFO] [stderr]    Compiling matchit v0.8.4
[INFO] [stderr]    Compiling pest_generator v2.8.3
[INFO] [stderr]    Compiling darling_core v0.23.0
[INFO] [stderr]    Compiling serde_derive_internals v0.29.1
[INFO] [stderr]    Compiling rmcp v0.15.0
[INFO] [stderr]    Compiling rustix v1.1.3
[INFO] [stderr]    Compiling lazy_static v1.5.0
[INFO] [stderr]    Compiling dyn-clone v1.0.20
[INFO] [stderr]    Compiling sharded-slab v0.1.7
[INFO] [stderr]    Compiling rand v0.9.2
[INFO] [stderr]    Compiling uuid v1.18.1
[INFO] [stderr]    Compiling tracing-log v0.2.0
[INFO] [stderr]    Compiling thread_local v1.1.9
[INFO] [stderr]    Compiling linux-raw-sys v0.11.0
[INFO] [stderr]    Compiling pastey v0.2.0
[INFO] [stderr]    Compiling anyhow v1.0.101
[INFO] [stderr]    Compiling base64 v0.22.1
[INFO] [stderr]    Compiling nu-ansi-term v0.50.3
[INFO] [stderr]    Compiling fastrand v2.3.0
[INFO] [stderr]    Compiling urlencoding v2.1.3
[INFO] [stderr]    Compiling serde_derive v1.0.228
[INFO] [stderr]    Compiling tracing-attributes v0.1.31
[INFO] [stderr]    Compiling tokio-macros v2.6.0
[INFO] [stderr]    Compiling futures-macro v0.3.31
[INFO] [stderr]    Compiling async-trait v0.1.89
[INFO] [stderr]    Compiling pest_derive v2.8.3
[INFO] [stderr]    Compiling ref-cast-impl v1.0.25
[INFO] [stderr]    Compiling clap_derive v4.5.55
[INFO] [stderr]    Compiling thiserror-impl v2.0.17
[INFO] [stderr]    Compiling schemars_derive v1.0.4
[INFO] [stderr]    Compiling globset v0.4.18
[INFO] [stderr]    Compiling matchers v0.2.0
[INFO] [stderr]    Compiling regex v1.12.3
[INFO] [stderr]    Compiling ignore v0.4.25
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling futures-util v0.3.31
[INFO] [stderr]    Compiling darling_macro v0.23.0
[INFO] [stderr]    Compiling tracing v0.1.44
[INFO] [stderr]    Compiling darling v0.23.0
[INFO] [stderr]    Compiling rmcp-macros v0.15.0
[INFO] [stderr]    Compiling axum-core v0.5.6
[INFO] [stderr]    Compiling tracing-subscriber v0.3.22
[INFO] [stderr]    Compiling clap v4.5.58
[INFO] [stderr]    Compiling bitflags v2.9.4
[INFO] [stderr]    Compiling chrono v0.4.43
[INFO] [stderr]    Compiling json5 v0.4.1
[INFO] [stderr]    Compiling serde_urlencoded v0.7.1
[INFO] [stderr]    Compiling ron v0.12.0
[INFO] [stderr]    Compiling orgize v0.10.0-alpha.10 (https://github.com/szaffarano/orgize/?rev=c4b38cb59131b4776e4edd52a1580737acb9df30#c4b38cb5)
[INFO] [stderr]    Compiling schemars v1.0.4
[INFO] [stderr]    Compiling futures-executor v0.3.31
[INFO] [stderr]    Compiling sse-stream v0.2.1
[INFO] [stderr]    Compiling hyper v1.8.1
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling tokio-stream v0.1.17
[INFO] [stderr]    Compiling tokio-util v0.7.16
[INFO] [stderr]    Compiling futures v0.3.31
[INFO] [stderr]    Compiling config v0.15.19
[INFO] [stderr]    Compiling tempfile v3.25.0
[INFO] [stderr]    Compiling hyper-util v0.1.19
[INFO] [stderr]    Compiling test-utils v0.0.6 (/opt/rustwide/workdir/test-utils)
[INFO] [stderr]    Compiling process-wrap v9.0.0
[INFO] [stderr]    Compiling axum v0.8.8
[INFO] [stderr]    Compiling org-core v0.0.6 (/opt/rustwide/workdir/org-core)
[INFO] [stderr]    Compiling org-cli v0.0.6 (/opt/rustwide/workdir/org-cli)
[INFO] [stderr]    Compiling org-mcp-server v0.0.6 (/opt/rustwide/workdir/org-mcp-server)
[INFO] [stderr]     Finished `dev` profile [unoptimized + debuginfo] target(s) in 1m 22s
[INFO] running `Command { std: "docker" "inspect" "0954d98544c48fb7c177380d0975511a638462d7eac2e487a10dd17408b0be50", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "0954d98544c48fb7c177380d0975511a638462d7eac2e487a10dd17408b0be50", kill_on_drop: false }`
[INFO] [stdout] 0954d98544c48fb7c177380d0975511a638462d7eac2e487a10dd17408b0be50
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen" "--no-run" "--message-format=json", kill_on_drop: false }`
[INFO] [stdout] abf6c7c10b25b2b0fc1b1b089318378f84d612db7835df0fba26c2efd6105a73
[INFO] running `Command { std: "docker" "start" "-a" "abf6c7c10b25b2b0fc1b1b089318378f84d612db7835df0fba26c2efd6105a73", kill_on_drop: false }`
[INFO] [stderr]    Compiling bstr v1.12.0
[INFO] [stderr]    Compiling parking_lot_core v0.9.12
[INFO] [stderr]    Compiling scopeguard v1.2.0
[INFO] [stderr]    Compiling sdd v3.0.10
[INFO] [stderr]    Compiling serial_test_derive v3.3.1
[INFO] [stderr]    Compiling tokio v1.49.0
[INFO] [stderr]    Compiling tracing-test-macro v0.2.6
[INFO] [stderr]    Compiling predicates-core v1.0.9
[INFO] [stderr]    Compiling float-cmp v0.10.0
[INFO] [stderr]    Compiling difflib v0.4.0
[INFO] [stderr]    Compiling assert_cmd v2.1.2
[INFO] [stderr]    Compiling normalize-line-endings v0.3.0
[INFO] [stderr]    Compiling termtree v0.5.1
[INFO] [stderr]    Compiling wait-timeout v0.2.1
[INFO] [stderr]    Compiling lock_api v0.4.14
[INFO] [stderr]    Compiling test-utils v0.0.6 (/opt/rustwide/workdir/test-utils)
[INFO] [stderr]    Compiling predicates-tree v1.0.12
[INFO] [stderr]    Compiling scc v2.4.0
[INFO] [stderr]    Compiling predicates v3.1.4
[INFO] [stderr]    Compiling parking_lot v0.12.5
[INFO] [stderr]    Compiling globset v0.4.18
[INFO] [stderr]    Compiling temp-env v0.3.6
[INFO] [stderr]    Compiling tracing-test v0.2.6
[INFO] [stderr]    Compiling ignore v0.4.25
[INFO] [stderr]    Compiling org-core v0.0.6 (/opt/rustwide/workdir/org-core)
[INFO] [stderr]    Compiling serial_test v3.3.1
[INFO] [stderr]    Compiling org-cli v0.0.6 (/opt/rustwide/workdir/org-cli)
[INFO] [stderr]    Compiling hyper v1.8.1
[INFO] [stderr]    Compiling tower v0.5.3
[INFO] [stderr]    Compiling tokio-stream v0.1.17
[INFO] [stderr]    Compiling tokio-util v0.7.16
[INFO] [stderr]    Compiling process-wrap v9.0.0
[INFO] [stderr]    Compiling tokio-test v0.4.5
[INFO] [stderr]    Compiling hyper-util v0.1.19
[INFO] [stderr]    Compiling axum v0.8.8
[INFO] [stderr]    Compiling rmcp v0.15.0
[INFO] [stderr]    Compiling org-mcp-server v0.0.6 (/opt/rustwide/workdir/org-mcp-server)
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 33.33s
[INFO] running `Command { std: "docker" "inspect" "abf6c7c10b25b2b0fc1b1b089318378f84d612db7835df0fba26c2efd6105a73", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "abf6c7c10b25b2b0fc1b1b089318378f84d612db7835df0fba26c2efd6105a73", kill_on_drop: false }`
[INFO] [stdout] abf6c7c10b25b2b0fc1b1b089318378f84d612db7835df0fba26c2efd6105a73
[INFO] running `Command { std: "docker" "create" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/target:/opt/rustwide/target:rw,Z" "-v" "/var/lib/crater-agent-workspace/builds/worker-2-tc2/source:/opt/rustwide/workdir:ro,Z" "-v" "/var/lib/crater-agent-workspace/cargo-home:/opt/rustwide/cargo-home:ro,Z" "-v" "/var/lib/crater-agent-workspace/rustup-home:/opt/rustwide/rustup-home:ro,Z" "-e" "SOURCE_DIR=/opt/rustwide/workdir" "-e" "CARGO_TARGET_DIR=/opt/rustwide/target" "-e" "CARGO_INCREMENTAL=0" "-e" "RUST_BACKTRACE=full" "-e" "RUSTFLAGS=--cap-lints=forbid" "-e" "RUSTDOCFLAGS=--cap-lints=forbid" "-e" "CARGO_HOME=/opt/rustwide/cargo-home" "-e" "RUSTUP_HOME=/opt/rustwide/rustup-home" "-w" "/opt/rustwide/workdir" "-m" "1610612736" "--user" "0:0" "--network" "none" "ghcr.io/rust-lang/crates-build-env/linux@sha256:d429b63d4308055ea97f60fb1d3dfca48854a00942f1bd2ad806beaf015945ec" "/opt/rustwide/cargo-home/bin/cargo" "+0abe69e6c16114311cc2700e972492e64d69c28f" "test" "--frozen", kill_on_drop: false }`
[INFO] [stdout] 41c3ebb9ba4dab2dd5f225916ff1829f61f4c80006dd14004826ec4fe45049b0
[INFO] running `Command { std: "docker" "start" "-a" "41c3ebb9ba4dab2dd5f225916ff1829f61f4c80006dd14004826ec4fe45049b0", kill_on_drop: false }`
[INFO] [stderr]     Finished `test` profile [unoptimized + debuginfo] target(s) in 0.43s
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/org_cli-c1f03ae7b0f76c45)
[INFO] [stdout] 
[INFO] [stdout] running 12 tests
[INFO] [stdout] test config::tests::test_generate_default_config ... ok
[INFO] [stdout] test commands::config::tests::test_init_config_creates_file ... ok
[INFO] [stdout] test config::tests::test_default_cli_config ... ok
[INFO] [stdout] test commands::config::tests::test_show_config_when_file_doesnt_exist ... ok
[INFO] [stdout] test commands::config::tests::test_show_path_adds_extension ... ok
[INFO] [stdout] test commands::config::tests::test_show_config_with_existing_file ... ok
[INFO] [stdout] test config::tests::test_cli_overrides ... ok
[INFO] [stdout] test commands::config::tests::test_show_config_extension_fallback ... ok
[INFO] [stdout] test commands::config::tests::test_init_config_file_already_exists ... ok
[INFO] [stdout] test config::tests::test_load_cli_config_extension_fallback ... ok
[INFO] [stdout] test config::tests::test_env_var_cli_override ... ok
[INFO] [stdout] test config::tests::test_load_from_file ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 12 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.02s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/integration_tests.rs (/opt/rustwide/target/debug/deps/integration_tests-f6f02dcd7432d737)
[INFO] [stdout] 
[INFO] [stdout] running 74 tests
[INFO] [stdout] test test_agenda_help ... ok
[INFO] [stdout] test test_agenda_list_command_json_format ... ok
[INFO] [stdout] test test_agenda_list_filter_by_tags ... ok
[INFO] [stdout] test test_agenda_list_filter_by_states ... ok
[INFO] [stdout] test test_agenda_list_plain_format ... ok
[INFO] [stdout] test test_agenda_list_filter_by_priority ... ok
[INFO] [stdout] test test_agenda_list_command_with_limit ... ok
[INFO] [stdout] test test_agenda_list_command_basic ... ok
[INFO] [stdout] test test_agenda_list_combined_states ... ok
[INFO] [stdout] test test_agenda_today_json_format ... ok
[INFO] [stdout] test test_agenda_list_multiple_filters ... ok
[INFO] [stdout] test test_agenda_range_command ... ok
[INFO] [stdout] test test_agenda_list_empty_agenda_files ... ok
[INFO] [stdout] test test_agenda_list_no_matching_priority ... ok
[INFO] [stdout] test test_config_file_affects_list_output ... ok
[INFO] [stdout] test test_config_hierarchy_file_env_cli ... ok
[INFO] [stdout] test test_agenda_today_plain_format_explicit ... ok
[INFO] [stdout] test test_config_init_creates_file_with_explicit_path ... ok
[INFO] [stdout] test test_config_init_creates_file ... ok
[INFO] [stdout] test test_agenda_range_command_invalid_start ... ok
[INFO] [stdout] test test_config_init_with_home_env ... ok
[INFO] [stdout] test test_config_init_file_already_exists_with_explicit_path ... ok
[INFO] [stdout] test test_config_init_file_already_exists ... ok
[INFO] [stdout] test test_agenda_today_with_tags ... ok
[INFO] [stdout] test test_config_respects_home_env ... ok
[INFO] [stdout] test test_agenda_list_with_limit_edge_case ... ok
[INFO] [stdout] test test_agenda_range_invalid_date ... ok
[INFO] [stdout] test test_agenda_week_json_format ... ok
[INFO] [stdout] test test_config_show_fallback_to_default ... ok
[INFO] [stdout] test test_agenda_today_command ... ok
[INFO] [stdout] test test_invalid_command ... ok
[INFO] [stdout] test test_config_path_with_explicit_flag ... ok
[INFO] [stdout] test test_agenda_week_command ... ok
[INFO] [stdout] test test_config_show_with_home_env ... ok
[INFO] [stdout] test test_agenda_week_plain_format ... ok
[INFO] [stdout] test test_config_show_with_explicit_path ... ok
[INFO] [stdout] test test_heading_command_nested ... ok
[INFO] [stdout] test test_heading_command_basic ... ok
[INFO] [stdout] test test_list_command_empty_directory ... ok
[INFO] [stdout] test test_element_by_id_command_heading ... ok
[INFO] [stdout] test test_agenda_range_command_invalid_end ... ok
[INFO] [stdout] test test_heading_command_nonexistent ... ok
[INFO] [stdout] test test_list_command_with_nonexistent_tag ... ok
[INFO] [stdout] test test_config_path_shows_location ... ok
[INFO] [stdout] test test_element_by_id_command_document_level ... ok
[INFO] [stdout] test test_config_show_displays_config ... ok
[INFO] [stdout] test test_list_command_basic ... ok
[INFO] [stdout] test test_help_command ... ok
[INFO] [stdout] test test_read_command_basic ... ok
[INFO] [stdout] test test_outline_command_basic ... ok
[INFO] [stdout] test test_list_command_json_format ... ok
[INFO] [stdout] test test_search_command_help ... ok
[INFO] [stdout] test test_list_command_with_tags_json_format ... ok
[INFO] [stdout] test test_search_command_no_results ... ok
[INFO] [stdout] test test_list_command_with_single_tag ... ok
[INFO] [stdout] test test_search_command_custom_snippet_size ... ok
[INFO] [stdout] test test_search_command_with_limit ... ok
[INFO] [stdout] test test_outline_command_json_format ... ok
[INFO] [stdout] test test_search_command_all_parameters ... ok
[INFO] [stdout] test test_version_command ... ok
[INFO] [stdout] test test_search_command_empty_query ... ok
[INFO] [stdout] test test_list_command_invalid_directory ... ok
[INFO] [stdout] test test_element_by_id_command_nonexistent ... ok
[INFO] [stdout] test test_search_command_invalid_directory ... ok
[INFO] [stdout] test test_search_command_json_format ... ok
[INFO] [stdout] test test_read_command_nonexistent_file ... ok
[INFO] [stdout] test test_search_command_with_multiple_tags ... ok
[INFO] [stdout] test test_list_command_with_multiple_tags ... ok
[INFO] [stdout] test test_search_command_with_single_tag ... ok
[INFO] [stdout] test test_search_command_with_tags_all_parameters ... ok
[INFO] [stdout] test test_search_command_with_tags_json_format ... ok
[INFO] [stdout] test test_search_command_basic ... ok
[INFO] [stdout] test test_search_command_with_tags_no_match ... ok
[INFO] [stdout] test test_search_command_with_tags_and_limit ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 74 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.62s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/org_core-ff3f1010b146a864)
[INFO] [stdout] 
[INFO] [stdout] running 55 tests
[INFO] [stdout] test config::tests::test_default_logging_config ... ok
[INFO] [stdout] test config::tests::test_finished_keywords_with_separator ... ok
[INFO] [stdout] test config::tests::test_config_serialization ... ok
[INFO] [stdout] test config::tests::test_finished_keywords_without_separator ... ok
[INFO] [stdout] test config::tests::test_unfinished_keywords_with_separator ... ok
[INFO] [stdout] test config::tests::test_env_var_override ... ok
[INFO] [stdout] test config::tests::test_unfinished_keywords_without_separator ... ok
[INFO] [stdout] test config::tests::test_validate_multiple_separators ... ok
[INFO] [stdout] test config::tests::test_validate_empty_keywords ... ok
[INFO] [stdout] test config::tests::test_validate_nonexistent_directory ... ok
[INFO] [stdout] test config::tests::test_validate_directory_expansion ... ok
[INFO] [stdout] test config::tests::test_validate_only_separator ... ok
[INFO] [stdout] test config::tests::test_validate_separator_at_beginning ... ok
[INFO] [stdout] test config::tests::test_validate_separator_at_end ... ok
[INFO] [stdout] test config::tests::test_validate_multiple_unfinished_and_finished ... ok
[INFO] [stdout] test config::tests::test_validate_non_directory_path ... ok
[INFO] [stdout] test error_tests::test_error_source_chain ... ok
[INFO] [stdout] test config::tests::test_validate_valid_keywords_without_separator ... ok
[INFO] [stdout] test config::tests::test_load_from_toml_file ... ok
[INFO] [stdout] test config::tests::test_default_org_config ... ok
[INFO] [stdout] test error_tests::test_empty_file_handling ... ok
[INFO] [stdout] test config::tests::test_validate_valid_keywords_with_separator ... ok
[INFO] [stdout] test config::tests::test_load_from_yaml_file ... ok
[INFO] [stdout] test config::tests::test_logging_config_file_extensions ... ok
[INFO] [stdout] test error_tests::test_error_display_formatting ... ok
[INFO] [stdout] test config::tests::test_load_from_yml_file ... ok
[INFO] [stdout] test error_tests::test_invalid_agenda_view_type_error ... ok
[INFO] [stdout] test error_tests::test_invalid_directory_error ... ok
[INFO] [stdout] test error_tests::test_config_error_conversion ... ok
[INFO] [stdout] test config::tests::test_validate_single_keyword ... ok
[INFO] [stdout] test error_tests::test_file_not_found_error ... ok
[INFO] [stdout] test error_tests::test_glob_error_conversion_and_display ... ok
[INFO] [stdout] test org_mode::tests::test_add_repeater_duration_day ... ok
[INFO] [stdout] test error_tests::test_with_defaults_error_handling ... ok
[INFO] [stdout] test error_tests::test_io_error_propagation ... ok
[INFO] [stdout] test org_mode::tests::test_add_repeater_duration_month ... ok
[INFO] [stdout] test org_mode::tests::test_add_repeater_duration_year ... ok
[INFO] [stdout] test org_mode::tests::test_last_day_of_month_february ... ok
[INFO] [stdout] test error_tests::test_parsing_error_handling ... ok
[INFO] [stdout] test error_tests::test_walk_error_conversion_and_source ... ok
[INFO] [stdout] test org_mode::tests::test_last_day_of_month_leap_year ... ok
[INFO] [stdout] test org_mode::tests::test_add_repeater_duration_month_boundary ... ok
[INFO] [stdout] test config::tests::test_load_from_json_file ... ok
[INFO] [stdout] test org_mode::tests::test_add_repeater_duration_week ... ok
[INFO] [stdout] test org_mode::tests::test_last_day_of_month_from_day_31 ... ok
[INFO] [stdout] test utils::tests::test_tags_match_both_empty ... ok
[INFO] [stdout] test utils::tests::test_tags_match_all_present ... ok
[INFO] [stdout] test org_mode::tests::test_add_repeater_duration_hour ... ok
[INFO] [stdout] test utils::tests::test_tags_match_exact ... ok
[INFO] [stdout] test utils::tests::test_tags_match_subset ... ok
[INFO] [stdout] test error_tests::test_invalid_heading_path_error ... ok
[INFO] [stdout] test error_tests::test_invalid_element_id_error ... ok
[INFO] [stdout] test utils::tests::test_tags_match_empty_filter ... ok
[INFO] [stdout] test utils::tests::test_tags_match_empty_items_with_filter ... ok
[INFO] [stdout] test utils::tests::test_tags_match_partial_match ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 55 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.01s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/agenda_tests.rs (/opt/rustwide/target/debug/deps/agenda_tests-da994a839a06a06c)
[INFO] [stdout] 
[INFO] [stdout] running 54 tests
[INFO] [stdout] test test_list_tasks_empty_agenda_files ... ok
[INFO] [stdout] test test_list_tasks_custom_todo_keywords ... ok
[INFO] [stdout] test test_get_agenda_view_custom_range ... ok
[INFO] [stdout] test test_get_agenda_view_empty_results ... ok
[INFO] [stdout] test test_get_agenda_view_custom_week ... ok
[INFO] [stdout] test test_get_agenda_view_current_week ... ok
[INFO] [stdout] test test_get_agenda_view_with_filters ... ok
[INFO] [stdout] test test_list_tasks_file_path_handling ... ok
[INFO] [stdout] test test_list_tasks_limit_zero ... ok
[INFO] [stdout] test test_list_tasks_nested_headlines ... ok
[INFO] [stdout] test test_agenda_today_finds_scheduled_tasks ... ok
[INFO] [stdout] test test_agenda_week_includes_all_week_tasks ... ok
[INFO] [stdout] test test_get_agenda_view_today ... ok
[INFO] [stdout] test test_list_tasks_basic ... ok
[INFO] [stdout] test test_list_tasks_scheduled_deadline ... ok
[INFO] [stdout] test test_list_tasks_with_limit ... ok
[INFO] [stdout] test test_agenda_today_excludes_future_tasks ... ok
[INFO] [stdout] test test_list_tasks_multiple_tags ... ok
[INFO] [stdout] test test_start_date_and_end_date_current_month ... ok
[INFO] [stdout] test test_start_date_and_end_date_current_week ... ok
[INFO] [stdout] test test_start_date_and_end_date_custom_range ... ok
[INFO] [stdout] test test_try_from_custom_range_from_greather_than_to ... ok
[INFO] [stdout] test test_try_from_custom_range_invalid_from_date ... ok
[INFO] [stdout] test test_start_date_and_end_date_today ... ok
[INFO] [stdout] test test_start_date_and_end_date_december ... ok
[INFO] [stdout] test test_start_date_and_end_date_specific_month ... ok
[INFO] [stdout] test test_start_date_and_end_date_specific_day ... ok
[INFO] [stdout] test test_try_from_custom_range_invalid_to_date ... ok
[INFO] [stdout] test test_try_from_empty_string ... ok
[INFO] [stdout] test test_try_from_custom_range_valid ... ok
[INFO] [stdout] test test_try_from_month_number_boundary_12 ... ok
[INFO] [stdout] test test_try_from_month_number_invalid ... ok
[INFO] [stdout] test test_try_from_month_number_out_of_range_13 ... ok
[INFO] [stdout] test test_try_from_month_number_valid ... ok
[INFO] [stdout] test test_try_from_specific_day_invalid_format ... ok
[INFO] [stdout] test test_try_from_month_number_out_of_range_zero ... ok
[INFO] [stdout] test test_try_from_specific_day_invalid_date ... ok
[INFO] [stdout] test test_try_from_month_number_boundary_1 ... ok
[INFO] [stdout] test test_try_from_month ... ok
[INFO] [stdout] test test_try_from_today ... ok
[INFO] [stdout] test test_try_from_specific_day_valid ... ok
[INFO] [stdout] test test_try_from_unknown_format ... ok
[INFO] [stdout] test test_list_tasks_glob_patterns ... ok
[INFO] [stdout] test test_try_from_week_number_invalid ... ok
[INFO] [stdout] test test_try_from_week ... ok
[INFO] [stdout] test test_try_from_week_number_valid ... ok
[INFO] [stdout] test test_list_tasks_combined_filters ... ok
[INFO] [stdout] test test_list_tasks_with_priority_filter ... ok
[INFO] [stdout] test test_list_tasks_todo_states ... ok
[INFO] [stdout] test test_list_tasks_with_state_filter ... ok
[INFO] [stdout] test test_list_tasks_specific_heading_content ... ok
[INFO] [stdout] test test_list_tasks_no_match_filters ... ok
[INFO] [stdout] test test_list_tasks_priorities ... ok
[INFO] [stdout] test test_list_tasks_with_tag_filter ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 54 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.18s
[INFO] [stdout] 
[INFO] [stderr]      Running tests/basic_use_cases.rs (/opt/rustwide/target/debug/deps/basic_use_cases-b5fc8f33be1ef997)
[INFO] [stdout] 
[INFO] [stdout] running 44 tests
[INFO] [stdout] test test_get_heading_invalid_path ... ok
[INFO] [stdout] test test_get_heading_nonexistent ... ok
[INFO] [stdout] test test_get_heading_second_level ... ok
[INFO] [stdout] test test_get_heading_nested_path ... ok
[INFO] [stdout] test test_get_outline_nonexistent_file ... ok
[INFO] [stdout] test test_get_outline_simple ... ok
[INFO] [stdout] test test_get_element_by_id_duplicate_id_precedence ... ok
[INFO] [stdout] test test_read_file_directory_error ... ok
[INFO] [stdout] test test_list_files_basic ... ok
[INFO] [stdout] test test_get_element_by_id_document_level ... ok
[INFO] [stdout] test test_list_files_with_limit ... ok
[INFO] [stdout] test test_get_outline_nested ... ok
[INFO] [stdout] test test_get_element_by_id_with_multiple_properties ... ok
[INFO] [stdout] test test_search_empty_query ... ok
[INFO] [stdout] test test_search_snippet_max_size_default ... ok
[INFO] [stdout] test test_search_basic_functionality ... ok
[INFO] [stdout] test test_search_unicode_boundary_safety ... ok
[INFO] [stdout] test test_config_getter ... ok
[INFO] [stdout] test test_get_element_by_id_special_characters ... ok
[INFO] [stdout] test test_get_element_by_id_nested_heading ... ok
[INFO] [stdout] test test_get_heading_simple ... ok
[INFO] [stdout] test test_search_snippet_max_size_custom ... ok
[INFO] [stdout] test test_get_element_by_id_multi_file_search ... ok
[INFO] [stdout] test test_search_with_limit ... ok
[INFO] [stdout] test test_get_element_by_id_case_sensitive ... ok
[INFO] [stdout] test test_get_element_by_id_whitespace_handling ... ok
[INFO] [stdout] test test_tree_node_creation ... ok
[INFO] [stdout] test test_get_element_by_id_simple_heading ... ok
[INFO] [stdout] test test_get_element_by_id_uuid_heading ... ok
[INFO] [stdout] test test_tree_node_indented_string ... ok
[INFO] [stdout] test test_tree_node_serialization ... ok
[INFO] [stdout] test test_search_snippet_max_size_large ... ok
[INFO] [stdout] test test_search_with_tags_none ... ok
[INFO] [stdout] test test_get_element_by_id_nonexistent ... ok
[INFO] [stdout] test test_search_all_terms_must_match ... ok
[INFO] [stdout] test test_search_with_tags_multiple_tags ... ok
[INFO] [stdout] test test_search_with_tags_no_match ... ok
[INFO] [stdout] test test_search_unicode_snippet_generation ... ok
[INFO] [stdout] test test_search_unicode_edge_cases ... ok
[INFO] [stdout] test test_search_with_tags_with_limit ... ok
[INFO] [stdout] test test_search_snippet_max_size_one ... ok
[INFO] [stdout] test test_search_snippet_max_size_zero ... ok
[INFO] [stdout] test test_search_with_tags_single_tag ... ok
[INFO] [stdout] test test_search_unicode_characters ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 44 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.56s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/org_mcp_server-80b69027122cc265)
[INFO] [stdout] 
[INFO] [stdout] running 21 tests
[INFO] [stdout] test config::tests::test_cli_overrides ... ok
[INFO] [stdout] test config::tests::test_env_var_server_override ... ok
[INFO] [stdout] test resources::tests::test_invalid_uris ... ok
[INFO] [stdout] test config::tests::test_save_to_file_creates_parent_directory ... ok
[INFO] [stdout] test resources::resource_tests::test_read_file_not_found ... ok
[INFO] [stdout] test resources::resource_tests::test_read_file_success ... ok
[INFO] [stdout] test resources::tests::test_boundary_cases ... ok
[INFO] [stdout] test resources::tests::test_case_sensitivity ... ok
[INFO] [stdout] test resources::tests::test_org_files_list_resource ... ok
[INFO] [stdout] test resources::resource_tests::test_id_success_heading ... ok
[INFO] [stdout] test resources::tests::test_org_heading_resource_parsing ... ok
[INFO] [stdout] test config::tests::test_save_to_file ... ok
[INFO] [stdout] test resources::resource_tests::test_id_not_found ... ok
[INFO] [stdout] test resources::tests::test_org_outline_resource_parsing ... ok
[INFO] [stdout] test resources::tests::test_org_resource_parsing ... ok
[INFO] [stdout] test resources::tests::test_uri_decoding_in_parsing ... ok
[INFO] [stdout] test resources::tests::test_uri_decoding ... ok
[INFO] [stdout] test resources::resource_tests::test_id_success_document_level ... ok
[INFO] [stdout] test config::tests::test_default_server_config ... ok
[INFO] [stdout] test config::tests::test_load_server_config_extension_fallback ... ok
[INFO] [stdout] test config::tests::test_load_from_file ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 21 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stderr]      Running unittests src/main.rs (/opt/rustwide/target/debug/deps/org_mcp_server-d2a4e513bac03c68)
[INFO] [stderr]      Running tests/integration_tests.rs (/opt/rustwide/target/debug/deps/integration_tests-00d288a069edb4db)
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 35 tests
[INFO] [stderr] [2m2026-03-30T02:53:59.520668Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpPZmKvs
[INFO] [stderr] [2m2026-03-30T02:53:59.524624Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpgMOoB4
[INFO] [stderr] [2m2026-03-30T02:53:59.526380Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpSgmJbu
[INFO] [stderr] [2m2026-03-30T02:53:59.526987Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpMNjwbx
[INFO] [stderr] [2m2026-03-30T02:53:59.532391Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpSe7tV7
[INFO] [stderr] [2m2026-03-30T02:53:59.533272Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpW8csYg
[INFO] [stderr] [2m2026-03-30T02:53:59.535701Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmp7RwRhH
[INFO] [stderr] [2m2026-03-30T02:53:59.538218Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.538318Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.538978Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpgeea5c
[INFO] [stderr] [2m2026-03-30T02:53:59.541158Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.541294Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.542572Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.542625Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.543707Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpJq7zln
[INFO] [stderr] [2m2026-03-30T02:53:59.544785Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpZjmx0m
[INFO] [stdout] test integration_tests::resources_tests::test_read_org_outline_resource ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.547681Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpI3CHNp
[INFO] [stderr] [2m2026-03-30T02:53:59.548086Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.548177Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.548552Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.548636Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.551377Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.551481Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.555684Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.555970Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.556114Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.556155Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.558262Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.558317Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.558549Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.558672Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stdout] test integration_tests::resources_tests::test_read_org_agenda_default_resource ... ok
[INFO] [stdout] test integration_tests::resources_tests::test_read_org_agenda_week_resource ... ok
[INFO] [stdout] test integration_tests::resources_tests::test_read_org_heading_resource ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.568701Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpg1uAHP
[INFO] [stderr] [2m2026-03-30T02:53:59.569712Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpZz0q6V
[INFO] [stderr] [2m2026-03-30T02:53:59.573297Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpkJpOci
[INFO] [stderr] [2m2026-03-30T02:53:59.573486Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpIYiSAi
[INFO] [stderr] [2m2026-03-30T02:53:59.579208Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.579375Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.580565Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.580641Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.580680Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.580751Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.581877Z[0m [33m WARN[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m response error [3mid[0m[2m=[0m1 [3merror[0m[2m=[0mErrorData { code: ErrorCode(-32602), message: "Invalid priority 'X'. Must be A, B, or C.", data: None }
[INFO] [stderr] [2m2026-03-30T02:53:59.590975Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.591110Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.598564Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.598663Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.622147Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpesHN2a
[INFO] [stderr] [2m2026-03-30T02:53:59.622655Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpAQsBoB
[INFO] [stderr] [2m2026-03-30T02:53:59.630456Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpHOgjiW
[INFO] [stderr] [2m2026-03-30T02:53:59.631870Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.631980Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.632293Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.632373Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.632769Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.632869Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.633148Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.633243Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.635006Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.635107Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.635500Z[0m [33m WARN[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m response error [3mid[0m[2m=[0m1 [3merror[0m[2m=[0mErrorData { code: ErrorCode(-32002), message: "Invalid resource URI format: invalid://path", data: Some(Object {"uri": String("invalid://path")}) }
[INFO] [stderr] [2m2026-03-30T02:53:59.636654Z[0m [33m WARN[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m response error [3mid[0m[2m=[0m2 [3merror[0m[2m=[0mErrorData { code: ErrorCode(-32603), message: "Failed to read org file 'nonexistent.org': IO error: File not found: nonexistent.org", data: Some(Object {"path": String("nonexistent.org"), "uri": String("org://nonexistent.org")}) }
[INFO] [stderr] [2m2026-03-30T02:53:59.638099Z[0m [33m WARN[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m response error [3mid[0m[2m=[0m3 [3merror[0m[2m=[0mErrorData { code: ErrorCode(-32603), message: "Failed to get outline for 'nonexistent.org': IO error: File not found: nonexistent.org", data: Some(Object {"path": String("nonexistent.org"), "uri": String("org-outline://nonexistent.org")}) }
[INFO] [stderr] [2m2026-03-30T02:53:59.640108Z[0m [33m WARN[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m response error [3mid[0m[2m=[0m4 [3merror[0m[2m=[0mErrorData { code: ErrorCode(-32603), message: "Failed to get heading 'NonexistentHeading' from 'notes.org': Invalid heading path: NonexistentHeading", data: Some(Object {"heading": String("NonexistentHeading"), "path": String("notes.org"), "uri": String("org-heading://notes.org#NonexistentHeading")}) }
[INFO] [stderr] [2m2026-03-30T02:53:59.647992Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.648093Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.648874Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.648882Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.648949Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.648969Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.653000Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.653141Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.653262Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.653349Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.654652Z[0m [33m WARN[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m response error [3mid[0m[2m=[0m1 [3merror[0m[2m=[0mErrorData { code: ErrorCode(-32602), message: "Invalid mode 'invalid'. Must be 'list' or 'view'.", data: None }
[INFO] [stderr] [2m2026-03-30T02:53:59.655764Z[0m [33m WARN[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m response error [3mid[0m[2m=[0m5 [3merror[0m[2m=[0mErrorData { code: ErrorCode(-32603), message: "Failed to get element by id 'nonexistent-id': Invalid element id: nonexistent-id", data: Some(Object {"id": String("nonexistent-id"), "uri": String("org-id://nonexistent-id")}) }
[INFO] [stdout] test integration_tests::resources_tests::test_org_agenda_resource_error_handling ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.656945Z[0m [33m WARN[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m response error [3mid[0m[2m=[0m6 [3merror[0m[2m=[0mErrorData { code: ErrorCode(-32002), message: "Invalid resource URI format: ", data: Some(Object {"uri": String("")}) }
[INFO] [stdout] test integration_tests::resources_tests::test_read_org_id_resource ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.658004Z[0m [33m WARN[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m response error [3mid[0m[2m=[0m7 [3merror[0m[2m=[0mErrorData { code: ErrorCode(-32002), message: "Invalid resource URI format: not-a-resource", data: Some(Object {"uri": String("not-a-resource")}) }
[INFO] [stdout] test integration_tests::resources_tests::test_list_resources ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.658992Z[0m [33m WARN[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m response error [3mid[0m[2m=[0m8 [3merror[0m[2m=[0mErrorData { code: ErrorCode(-32002), message: "Invalid resource URI format: org-heading://notes.org", data: Some(Object {"uri": String("org-heading://notes.org")}) }
[INFO] [stderr] [2m2026-03-30T02:53:59.659132Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpAC4giU
[INFO] [stderr] [2m2026-03-30T02:53:59.660035Z[0m [33m WARN[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m response error [3mid[0m[2m=[0m9 [3merror[0m[2m=[0mErrorData { code: ErrorCode(-32002), message: "Invalid resource URI format: org-heading://#heading", data: Some(Object {"uri": String("org-heading://#heading")}) }
[INFO] [stdout] test integration_tests::tools_tests::test_org_agenda_tool_invalid_priority ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.665538Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.665672Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.672342Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpyhizYG
[INFO] [stderr] [2m2026-03-30T02:53:59.693975Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpczisei
[INFO] [stderr] [2m2026-03-30T02:53:59.693978Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmp8ODkxG
[INFO] [stderr] [2m2026-03-30T02:53:59.694657Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.694778Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.697151Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.697304Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.700185Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.700253Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.700275Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.700264Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.700309Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.700313Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.700307Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.700363Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.700762Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.700831Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.700880Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.700936Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.703617Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.703697Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.704315Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.704422Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stdout] test integration_tests::server_tests::test_mcp_server_with_config_file ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.707236Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.707236Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.707312Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.707312Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.707944Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.708007Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stdout] test integration_tests::tools_tests::test_org_agenda_tool_all_parameters ... ok
[INFO] [stdout] test integration_tests::resources_tests::test_read_org_file_resource ... ok
[INFO] [stdout] test integration_tests::server_tests::test_mcp_server_connection ... ok
[INFO] [stdout] test integration_tests::tools_tests::test_list_tools ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.723393Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmp5xjR2q
[INFO] [stderr] [2m2026-03-30T02:53:59.723512Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpgs2Vz8
[INFO] [stderr] [2m2026-03-30T02:53:59.727713Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmphhlrk4
[INFO] [stdout] test integration_tests::resources_tests::test_invalid_resource_uris ... ok
[INFO] [stdout] test integration_tests::tools_tests::test_org_agenda_tool_invalid_date_format ... ok
[INFO] [stdout] test integration_tests::resources_tests::test_list_resource_templates ... ok
[INFO] [stdout] test integration_tests::resources_tests::test_read_org_agenda_today_resource ... ok
[INFO] [stdout] test integration_tests::tools_tests::test_org_agenda_tool_invalid_mode ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.730643Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpvG0czi
[INFO] [stderr] [2m2026-03-30T02:53:59.735963Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.736034Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.738895Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.739715Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.740973Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpKstKsZ
[INFO] [stderr] [2m2026-03-30T02:53:59.747157Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpxNOypf
[INFO] [stderr] [2m2026-03-30T02:53:59.750013Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmp7eMMCZ
[INFO] [stderr] [2m2026-03-30T02:53:59.751043Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.752709Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmp5vZcnu
[INFO] [stderr] [2m2026-03-30T02:53:59.754451Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.754554Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.756961Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpbifdIP
[INFO] [stderr] [2m2026-03-30T02:53:59.758360Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.758474Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.760198Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.760309Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.760760Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.760908Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.763768Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpyP1iJ9
[INFO] [stderr] [2m2026-03-30T02:53:59.764326Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.764389Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.766772Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.767228Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.767415Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.768843Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.768958Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.770354Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.770457Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.770855Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpktGGrn
[INFO] [stderr] [2m2026-03-30T02:53:59.771956Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.772039Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stdout] test integration_tests::tools_tests::test_org_agenda_tool_list_with_limit ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.773190Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stdout] test integration_tests::tools_tests::test_org_file_list_tool ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.773288Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stdout] test integration_tests::tools_tests::test_org_agenda_tool_list_with_tags ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.774180Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stdout] test integration_tests::tools_tests::test_org_file_list_tool_with_tags_and_limit ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.774268Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stdout] test integration_tests::tools_tests::test_org_agenda_tool_list_with_priority ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.778690Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stdout] test integration_tests::tools_tests::test_org_agenda_tool_list_with_states ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.779008Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stdout] test integration_tests::tools_tests::test_org_file_list_tool_with_limit ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.779179Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stdout] test integration_tests::tools_tests::test_org_agenda_tool_view_mode_default ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.779235Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stdout] test integration_tests::resources_tests::test_read_org_directory_resource ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.781973Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stdout] test integration_tests::tools_tests::test_org_agenda_tool_view_mode_custom_range ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.782079Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stdout] test integration_tests::tools_tests::test_org_agenda_tool_list_mode ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.782349Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.782464Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.782531Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.783939Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.787021Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.787154Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stderr] [2m2026-03-30T02:53:59.788253Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.788513Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.790955Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.791282Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.798350Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmphUHush
[INFO] [stderr] [2m2026-03-30T02:53:59.803082Z[0m [32m INFO[0m [2morg_mcp_server[0m[2m:[0m Starting MCP server with org directory: /tmp/.tmpCrQxUD
[INFO] [stderr] [2m2026-03-30T02:53:59.805504Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stderr] [2m2026-03-30T02:53:59.805643Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stdout] test integration_tests::tools_tests::test_org_search_tool_with_multiple_tags ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.808147Z[0m [32m INFO[0m [2mrmcp::handler::server[0m[2m:[0m client initialized
[INFO] [stdout] test integration_tests::tools_tests::test_org_search_tool ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.808263Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m Service initialized as server [3mpeer_info[0m[2m=[0mSome(InitializeRequestParams { meta: None, protocol_version: ProtocolVersion("2025-03-26"), capabilities: ClientCapabilities { experimental: None, extensions: None, roots: None, sampling: None, elicitation: None, tasks: None }, client_info: Implementation { name: "rmcp", title: None, version: "0.15.0", description: None, icons: None, website_url: None } })
[INFO] [stdout] test integration_tests::tools_tests::test_org_file_list_tool_with_tags ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.813149Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.813274Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.819515Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.819718Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.833373Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.833600Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stderr] [2m2026-03-30T02:53:59.872065Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.872430Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stdout] test integration_tests::tools_tests::test_org_search_tool_with_parameters ... ok
[INFO] [stderr] [2m2026-03-30T02:53:59.971053Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m input stream terminated
[INFO] [stderr] [2m2026-03-30T02:53:59.971416Z[0m [32m INFO[0m [1mserve_inner[0m[2m:[0m [2mrmcp::service[0m[2m:[0m serve finished [3mquit_reason[0m[2m=[0mClosed
[INFO] [stdout] test integration_tests::tools_tests::test_org_search_tool_with_tags ... ok
[INFO] [stderr] [2m2026-03-30T02:54:00.524022Z[0m [31mERROR[0m [2morg_mcp_server[0m[2m:[0m Error starting server: connection closed: initialized request
[INFO] [stderr] Error: ConnectionClosed("initialized request")
[INFO] [stdout] test integration_tests::server_tests::test_graceful_close_mcp_server ... ok
[INFO] [stderr]      Running unittests src/lib.rs (/opt/rustwide/target/debug/deps/test_utils-bbcd91786508feaa)
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 35 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 1.04s
[INFO] [stdout] 
[INFO] [stdout] 
[INFO] [stdout] running 13 tests
[INFO] [stdout] test config::tests::test_create_yml_config ... ok
[INFO] [stdout] test config::tests::test_normalize_path ... ok
[INFO] [stdout] test dates::tests::test_format_org_date_without_time ... ok
[INFO] [stdout] test dates::tests::test_format_org_date_with_time ... ok
[INFO] [stdout] test dates::tests::test_replace_dates_with_time ... ok
[INFO] [stdout] test dates::tests::test_replace_dates_today ... ok
[INFO] [stdout] test dates::tests::test_replace_dates_today_plus ... ok
[INFO] [stdout] test config::tests::test_create_json_config ... ok
[INFO] [stdout] test config::tests::test_create_yaml_config ... ok
[INFO] [stdout] test fixtures::tests::test_copy_specific_fixtures ... ok
[INFO] [stdout] test fixtures::tests::test_setup_test_org_files ... ok
[INFO] [stdout] test config::tests::test_create_toml_config ... ok
[INFO] [stdout] test fixtures::tests::test_copy_fixtures_to_temp ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 13 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.07s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests org_core
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests org_mcp_server
[INFO] [stdout] 
[INFO] [stdout] running 0 tests
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 0 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.00s
[INFO] [stdout] 
[INFO] [stderr]    Doc-tests test_utils
[INFO] [stdout] 
[INFO] [stdout] running 6 tests
[INFO] [stdout] test test-utils/src/config.rs - config::create_toml_config (line 33) - compile ... ok
[INFO] [stdout] test test-utils/src/fixtures.rs - fixtures::copy_fixtures_with_dates (line 153) - compile ... ok
[INFO] [stdout] test test-utils/src/fixtures.rs - fixtures::copy_specific_fixtures (line 110) - compile ... ok
[INFO] [stdout] test test-utils/src/fixtures.rs - fixtures::setup_test_org_files_with_dates (line 87) - compile ... ok
[INFO] [stdout] test test-utils/src/fixtures.rs - fixtures::copy_fixtures_to_temp (line 23) - compile ... ok
[INFO] [stdout] test test-utils/src/fixtures.rs - fixtures::setup_test_org_files (line 66) - compile ... ok
[INFO] [stdout] 
[INFO] [stdout] test result: ok. 6 passed; 0 failed; 0 ignored; 0 measured; 0 filtered out; finished in 0.09s
[INFO] [stdout] 
[INFO] [stdout] all doctests ran in 0.99s; merged doctests compilation took 0.86s
[INFO] running `Command { std: "docker" "inspect" "41c3ebb9ba4dab2dd5f225916ff1829f61f4c80006dd14004826ec4fe45049b0", kill_on_drop: false }`
[INFO] running `Command { std: "docker" "rm" "-f" "41c3ebb9ba4dab2dd5f225916ff1829f61f4c80006dd14004826ec4fe45049b0", kill_on_drop: false }`
[INFO] [stdout] 41c3ebb9ba4dab2dd5f225916ff1829f61f4c80006dd14004826ec4fe45049b0
